debian:/usr/local/etc/raddb# radiusd -X
FreeRADIUS Version 2.1.7, for host i686-pc-linux-gnu, built on Oct  2 2009 at 11:03:25
Copyright (C) 1999-2009 The FreeRADIUS server project and contributors.
There is NO warranty; not even for MERCHANTABILITY or FITNESS FOR A
PARTICULAR PURPOSE.
You may redistribute copies of FreeRADIUS under the terms of the
GNU General Public License v2.
Starting - reading configuration files ...
including configuration file /usr/local/etc/raddb/radiusd.conf
including configuration file /usr/local/etc/raddb/proxy.conf
including configuration file /usr/local/etc/raddb/clients.conf
including files in directory /usr/local/etc/raddb/modules/
including configuration file /usr/local/etc/raddb/modules/mac2vlan
including configuration file /usr/local/etc/raddb/modules/policy
including configuration file /usr/local/etc/raddb/modules/radutmp
including configuration file /usr/local/etc/raddb/modules/checkval
including configuration file /usr/local/etc/raddb/modules/always
including configuration file /usr/local/etc/raddb/modules/pap
including configuration file /usr/local/etc/raddb/modules/mac2ip
including configuration file /usr/local/etc/raddb/modules/files
including configuration file /usr/local/etc/raddb/modules/sradutmp
including configuration file /usr/local/etc/raddb/modules/wimax
including configuration file /usr/local/etc/raddb/modules/unix
including configuration file /usr/local/etc/raddb/modules/cui
including configuration file /usr/local/etc/raddb/modules/inner-eap
including configuration file /usr/local/etc/raddb/modules/attr_filter
including configuration file /usr/local/etc/raddb/modules/perl
including configuration file /usr/local/etc/raddb/modules/chap
including configuration file /usr/local/etc/raddb/modules/detail.log
including configuration file /usr/local/etc/raddb/modules/exec
including configuration file /usr/local/etc/raddb/modules/ldap
including configuration file /usr/local/etc/raddb/modules/pam
including configuration file /usr/local/etc/raddb/modules/etc_group
including configuration file /usr/local/etc/raddb/modules/smbpasswd
including configuration file /usr/local/etc/raddb/modules/mschap
including configuration file /usr/local/etc/raddb/modules/smsotp
including configuration file /usr/local/etc/raddb/modules/preprocess
including configuration file /usr/local/etc/raddb/modules/passwd
including configuration file /usr/local/etc/raddb/modules/realm
including configuration file /usr/local/etc/raddb/modules/acct_unique
including configuration file /usr/local/etc/raddb/modules/sqlcounter_expire_on_login
including configuration file /usr/local/etc/raddb/modules/ippool
including configuration file /usr/local/etc/raddb/modules/sql_log
including configuration file /usr/local/etc/raddb/modules/logintime
including configuration file /usr/local/etc/raddb/modules/echo
including configuration file /usr/local/etc/raddb/modules/counter
including configuration file /usr/local/etc/raddb/modules/linelog
including configuration file /usr/local/etc/raddb/modules/otp
including configuration file /usr/local/etc/raddb/modules/expiration
including configuration file /usr/local/etc/raddb/modules/detail.example.com
including configuration file /usr/local/etc/raddb/modules/digest
including configuration file /usr/local/etc/raddb/modules/detail
including configuration file /usr/local/etc/raddb/modules/krb5
including configuration file /usr/local/etc/raddb/modules/attr_rewrite
including configuration file /usr/local/etc/raddb/modules/expr
including configuration file /usr/local/etc/raddb/eap.conf
including configuration file /usr/local/etc/raddb/policy.conf
including files in directory /usr/local/etc/raddb/sites-enabled/
including configuration file /usr/local/etc/raddb/sites-enabled/default
including configuration file /usr/local/etc/raddb/sites-enabled/proxy-inner-tunnel
including configuration file /usr/local/etc/raddb/sites-enabled/control-socket
including dictionary file /usr/local/etc/raddb/dictionary
main {
        prefix = "/usr/local"
        localstatedir = "/usr/local/var"
        logdir = "/usr/local/var/log/radius"
        libdir = "/usr/local/lib"
        radacctdir = "/usr/local/var/log/radius/radacct"
        hostname_lookups = no
        max_request_time = 30
        cleanup_delay = 5
        max_requests = 1024
        allow_core_dumps = no
        pidfile = "/usr/local/var/run/radiusd/radiusd.pid"
        checkrad = "/usr/local/sbin/checkrad"
        debug_level = 0
        proxy_requests = yes
 log {
        stripped_names = no
        auth = no
        auth_badpass = no
        auth_goodpass = no
 }
 security {
        max_attributes = 200
        reject_delay = 1
        status_server = yes
 }
}
radiusd: #### Loading Realms and Home Servers ####
 proxy server {
        retry_delay = 5
        retry_count = 3
        default_fallback = no
        dead_time = 120
        wake_all_if_all_dead = no
 }
 home_server localhost {
        ipaddr = 127.0.0.1
        port = 1812
        type = "auth"
        secret = "testing123"
        response_window = 20
        max_outstanding = 65536
        require_message_authenticator = no
        zombie_period = 40
        status_check = "status-server"
        ping_interval = 30
        check_interval = 30
        num_answers_to_alive = 3
        num_pings_to_alive = 3
        revive_interval = 120
        status_check_timeout = 4
        irt = 2
        mrt = 16
        mrc = 5
        mrd = 30
 }
 home_server_pool my_auth_failover {
        type = fail-over
        home_server = localhost
 }
 realm example.com {
        auth_pool = my_auth_failover
 }
 realm LOCAL {
 }
 realm jacques.net {
        authhost = 192.168.0.252:1812
        accthost = 192.168.0.252:1813
        secret = lrnp2tlm
 }
radiusd: #### Loading Clients ####
 client localhost {
        ipaddr = 127.0.0.1
        require_message_authenticator = no
        secret = "testing123"
        nastype = "other"
 }
 client 192.168.0.250 {
        require_message_authenticator = no
        secret = "lrnp2tlm"
        shortname = "AP1"
 }
radiusd: #### Instantiating modules ####
 instantiate {
 Module: Linked to module rlm_exec
 Module: Instantiating exec
  exec {
        wait = no
        input_pairs = "request"
        shell_escape = yes
  }
 Module: Linked to module rlm_expr
 Module: Instantiating expr
 Module: Linked to module rlm_expiration
 Module: Instantiating expiration
  expiration {
        reply-message = "Password Has Expired  "
  }
 Module: Linked to module rlm_logintime
 Module: Instantiating logintime
  logintime {
        reply-message = "You are calling outside your allowed timespan  "
        minimum-timeout = 60
  }
 }
radiusd: #### Loading Virtual Servers ####
server proxy-inner-tunnel {
 modules {
 Module: Checking authenticate {...} for more modules to load
 Module: Linked to module rlm_eap
 Module: Instantiating eap
  eap {
        default_eap_type = "peap"
        timer_expire = 60
        ignore_unknown_eap_types = no
        cisco_accounting_username_bug = no
        max_sessions = 2048
  }
 Module: Linked to sub-module rlm_eap_md5
 Module: Instantiating eap-md5
 Module: Linked to sub-module rlm_eap_leap
 Module: Instantiating eap-leap
 Module: Linked to sub-module rlm_eap_gtc
 Module: Instantiating eap-gtc
   gtc {
        challenge = "Password: "
        auth_type = "PAP"
   }
 Module: Linked to sub-module rlm_eap_tls
 Module: Instantiating eap-tls
   tls {
        rsa_key_exchange = no
        dh_key_exchange = yes
        rsa_key_length = 512
        dh_key_length = 512
        verify_depth = 0
        pem_file_type = yes
        private_key_file = "/usr/local/etc/raddb/certs/server.pem"
        certificate_file = "/usr/local/etc/raddb/certs/server.pem"
        CA_file = "/usr/local/etc/raddb/certs/ca.pem"
        private_key_password = "whatever"
        dh_file = "/usr/local/etc/raddb/certs/dh"
        random_file = "/usr/local/etc/raddb/certs/random"
        fragment_size = 1024
        include_length = yes
        check_crl = no
        cipher_list = "DEFAULT"
        make_cert_command = "/usr/local/etc/raddb/certs/bootstrap"
    cache {
        enable = no
        lifetime = 24
        max_entries = 255
    }
   }
 Module: Linked to sub-module rlm_eap_ttls
 Module: Instantiating eap-ttls
   ttls {
        default_eap_type = "md5"
        copy_request_to_tunnel = no
        use_tunneled_reply = no
        virtual_server = "proxy-inner-tunnel"
        include_length = yes
   }
 Module: Linked to sub-module rlm_eap_peap
 Module: Instantiating eap-peap
   peap {
        default_eap_type = "mschapv2"
        copy_request_to_tunnel = yes
        use_tunneled_reply = yes
        proxy_tunneled_request_as_eap = no
        virtual_server = "proxy-inner-tunnel"
   }
 Module: Linked to sub-module rlm_eap_mschapv2
 Module: Instantiating eap-mschapv2
   mschapv2 {
        with_ntdomain_hack = no
   }
 Module: Checking authorize {...} for more modules to load
 Module: Checking post-proxy {...} for more modules to load
 } # modules
} # server
server {
 modules {
 Module: Checking authenticate {...} for more modules to load
 Module: Linked to module rlm_pap
 Module: Instantiating pap
  pap {
        encryption_scheme = "auto"
        auto_header = no
  }
 Module: Linked to module rlm_chap
 Module: Instantiating chap
 Module: Linked to module rlm_mschap
 Module: Instantiating mschap
  mschap {
        use_mppe = yes
        require_encryption = no
        require_strong = no
        with_ntdomain_hack = no
  }
 Module: Linked to module rlm_unix
 Module: Instantiating unix
  unix {
        radwtmp = "/usr/local/var/log/radius/radwtmp"
  }
 Module: Checking authorize {...} for more modules to load
 Module: Linked to module rlm_preprocess
 Module: Instantiating preprocess
  preprocess {
        huntgroups = "/usr/local/etc/raddb/huntgroups"
        hints = "/usr/local/etc/raddb/hints"
        with_ascend_hack = no
        ascend_channels_per_line = 23
        with_ntdomain_hack = no
        with_specialix_jetstream_hack = no
        with_cisco_vsa_hack = no
        with_alvarion_vsa_hack = no
  }
 Module: Linked to module rlm_files
 Module: Instantiating files
  files {
        usersfile = "/usr/local/etc/raddb/users"
        acctusersfile = "/usr/local/etc/raddb/acct_users"
        preproxy_usersfile = "/usr/local/etc/raddb/preproxy_users"
        compat = "no"
  }
 Module: Checking preacct {...} for more modules to load
 Module: Linked to module rlm_acct_unique
 Module: Instantiating acct_unique
  acct_unique {
        key = "User-Name, Acct-Session-Id, NAS-IP-Address, Client-IP-Address, NAS-Port"
  }
 Module: Linked to module rlm_realm
 Module: Instantiating suffix
  realm suffix {
        format = "suffix"
        delimiter = "@"
        ignore_default = no
        ignore_null = no
  }
 Module: Checking accounting {...} for more modules to load
 Module: Linked to module rlm_detail
 Module: Instantiating detail
  detail {
        detailfile = "/usr/local/var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d"
        header = "%t"
        detailperm = 384
        dirperm = 493
        locking = no
        log_packet_header = no
  }
 Module: Linked to module rlm_radutmp
 Module: Instantiating radutmp
  radutmp {
        filename = "/usr/local/var/log/radius/radutmp"
        username = "%{User-Name}"
        case_sensitive = yes
        check_with_nas = yes
        perm = 384
        callerid = yes
  }
 Module: Linked to module rlm_attr_filter
 Module: Instantiating attr_filter.accounting_response
  attr_filter attr_filter.accounting_response {
        attrsfile = "/usr/local/etc/raddb/attrs.accounting_response"
        key = "%{User-Name}"
  }
 Module: Checking session {...} for more modules to load
 Module: Checking post-proxy {...} for more modules to load
 Module: Checking post-auth {...} for more modules to load
 Module: Instantiating attr_filter.access_reject
  attr_filter attr_filter.access_reject {
        attrsfile = "/usr/local/etc/raddb/attrs.access_reject"
        key = "%{User-Name}"
  }
 } # modules
} # server
radiusd: #### Opening IP addresses and Ports ####
listen {
        type = "auth"
        ipaddr = *
        port = 0
}
listen {
        type = "acct"
        ipaddr = *
        port = 0
}
listen {
        type = "control"
 listen {
        socket = "/usr/local/var/run/radiusd/radiusd.sock"
 }
}
Listening on authentication address * port 1812
Listening on accounting address * port 1813
Listening on command file /usr/local/var/run/radiusd/radiusd.sock
Listening on proxy address * port 1814
Ready to process requests.
rad_recv: Access-Request packet from host 192.168.0.250 port 32769, id=94, length=229
        Acct-Session-Id = "5d932d5c-00000041"
        NAS-Port = 66
        NAS-Port-Type = Wireless-802.11
        NAS-Identifier = "AP1"
        NAS-IP-Address = 192.168.0.250
        Framed-MTU = 1496
        User-Name = "test"
        Calling-Station-Id = "00-13-02-C4-80-4C"
        Called-Station-Id = "00-0F-61-FE-EF-D2"
        Service-Type = Framed-User
        EAP-Message = 0x025e00090174657374
        Colubris-AVPair = "ssid=test2"
        Colubris-AVPair = "vsc-unique-id=3"
        Colubris-AVPair = "phytype=IEEE802dot11g"
        Colubris-Attr-250 = 0x00000000
        Colubris-Attr-249 = 0x00000000
        Message-Authenticator = 0x18249db7421d9f297c00533273d364ed
+- entering group authorize {...}
++[preprocess] returns ok
++[chap] returns noop
++[mschap] returns noop
[eap] EAP packet type response id 94 length 9
[eap] No EAP Start, assuming it's an on-going EAP conversation
++[eap] returns updated
++[unix] returns notfound
++[files] returns noop
++[expiration] returns noop
++[logintime] returns noop
[pap] WARNING! No "known good" password found for the user.  Authentication may fail because of this.
++[pap] returns noop
Found Auth-Type = EAP
+- entering group authenticate {...}
[eap] EAP Identity
[eap] processing type tls
[tls] Initiate
[tls] Start returned 1
++[eap] returns handled
Sending Access-Challenge of id 94 to 192.168.0.250 port 32769
        EAP-Message = 0x015f00061920
        Message-Authenticator = 0x00000000000000000000000000000000
        State = 0x2fcecf572f91d62120794f6da7ad342b
Finished request 0.
Going to the next request
Waking up in 4.9 seconds.
rad_recv: Access-Request packet from host 192.168.0.250 port 32769, id=21, length=346
        Acct-Session-Id = "5d932d5c-00000041"
        NAS-Port = 66
        NAS-Port-Type = Wireless-802.11
        NAS-Identifier = "AP1"
        NAS-IP-Address = 192.168.0.250
        Framed-MTU = 1496
        User-Name = "test"
        Calling-Station-Id = "00-13-02-C4-80-4C"
        Called-Station-Id = "00-0F-61-FE-EF-D2"
        Service-Type = Framed-User
        EAP-Message = 0x025f006c190016030100610100005d03014ac5cb2928add118f0f1876069ca800149926161735e7a3edda5b1421e4e6f3b00003600390038003500160013000a00330032002f0007006600050004006300620061001500120009006500640060001400110008000600030100
        State = 0x2fcecf572f91d62120794f6da7ad342b
        Colubris-AVPair = "ssid=test2"
        Colubris-AVPair = "vsc-unique-id=3"
        Colubris-AVPair = "phytype=IEEE802dot11g"
        Colubris-Attr-250 = 0x00000000
        Colubris-Attr-249 = 0x00000000
        Message-Authenticator = 0x7526af2dd675190b53adef5761ff448f
+- entering group authorize {...}
++[preprocess] returns ok
++[chap] returns noop
++[mschap] returns noop
[eap] EAP packet type response id 95 length 108
[eap] Continuing tunnel setup.
++[eap] returns ok
Found Auth-Type = EAP
+- entering group authenticate {...}
[eap] Request found, released from the list
[eap] EAP/peap
[eap] processing type peap
[peap] processing EAP-TLS
[peap] eaptls_verify returned 7
[peap] Done initial handshake
[peap]     (other): before/accept initialization
[peap]     TLS_accept: before/accept initialization
[peap] <<< TLS 1.0 Handshake [length 0061], ClientHello
[peap]     TLS_accept: SSLv3 read client hello A
[peap] >>> TLS 1.0 Handshake [length 002a], ServerHello
[peap]     TLS_accept: SSLv3 write server hello A
[peap] >>> TLS 1.0 Handshake [length 085e], Certificate
[peap]     TLS_accept: SSLv3 write certificate A
[peap] >>> TLS 1.0 Handshake [length 020d], ServerKeyExchange
[peap]     TLS_accept: SSLv3 write key exchange A
[peap] >>> TLS 1.0 Handshake [length 0004], ServerHelloDone
[peap]     TLS_accept: SSLv3 write server done A
[peap]     TLS_accept: SSLv3 flush data
[peap]     TLS_accept: Need to read more data: SSLv3 read client certificate A
In SSL Handshake Phase
In SSL Accept mode
[peap] eaptls_process returned 13
[peap] EAPTLS_HANDLED
++[eap] returns handled
Sending Access-Challenge of id 21 to 192.168.0.250 port 32769
        EAP-Message = 0x0160040019c000000aad160301002a0200002603014ac5cb438a88b9c1997e9a1c3b188c7b31304c4344df83fbbcfd2257d87a908000003900160301085e0b00085a0008570003a6308203a23082028aa003020102020101300d06092a864886f70d0101040500308193310b3009060355040613024652310f300d060355040813065261646975733112301006035504071309536f6d65776865726531153013060355040a130c4578616d706c6520496e632e3120301e06092a864886f70d010901161161646d696e406578616d706c652e636f6d312630240603550403131d4578616d706c6520436572746966696361746520417574686f72697479
        EAP-Message = 0x301e170d3039313030323039313031395a170d3130313030323039313031395a307c310b3009060355040613024652310f300d0603550408130652616469757331153013060355040a130c4578616d706c6520496e632e312330210603550403131a4578616d706c65205365727665722043657274696669636174653120301e06092a864886f70d010901161161646d696e406578616d706c652e636f6d30820122300d06092a864886f70d01010105000382010f003082010a0282010100b9eaf28ba93b9e48fbfe78db1f698757ce7acd7bbda077ad9d3041027732903d5f326055c67acb09ee26a8cc711ff319fdde1ffcf7bd11ed146659ceea1f
        EAP-Message = 0xc82738b75c3362fd2b12ef6247fecddd9db4997516a1cd1a00661178a44ca1565f6d516dd1968bd12e79fde01f4cbd4024c8fadda291f1b873834fde4567deb6216461c36b76522b7bf918f2e0dce2708e7fa22d93bf91ff7034ddca9f77503f20963951954fc5a2d2f39e64ac3dfd3222c76e516c035772f37dfe8958b8a6a6eb49bc892a01c5f07e3791f63d7cd80fc67f6f20721cdefec5b28355d3b53d3a7bb9eb2a1f005c137b8ef9639dd2a7ea3fef07ee163fcb237cf7be4b02887bdc6a010203010001a317301530130603551d25040c300a06082b06010505070301300d06092a864886f70d01010405000382010100777fcc57fe0ff934b7
        EAP-Message = 0xa164cd03a94a9449d163b0c438e529d9d6b2a5a3e285943bc64617af14f2d3e3ffc022f75d340eb16ead2c5e5588ddaa79121d41aa2c7313ef8a3b02eccf1fe18be2a77e903d533b95b7b9d19032df053ccc9e674f1f4c63173dddc8f37ca2be34a203747a4db30be8caa4509ccbcd4750bb2879fa305f3a82e55aa32526838e4af82f1d36e91021bdcf7de1d180bb9bfb7b2ba4acee37cbff6b2273970b4d403efb271367a50f30f3c53eb366e65a97238112f9b03bbfa3db0865cc0920f5ccbf7a6b3d2904a0241d1e8cff4cea15d614f7f4b72a0afde50075ddac493725f8fb916aa0ae9fa035bd006047a5c8349819d369d4529e4a0004ab308204
        EAP-Message = 0xa73082038fa0030201020209
        Message-Authenticator = 0x00000000000000000000000000000000
        State = 0x2fcecf572eaed62120794f6da7ad342b
Finished request 1.
Going to the next request
Waking up in 4.7 seconds.
rad_recv: Access-Request packet from host 192.168.0.250 port 32769, id=178, length=244
        Acct-Session-Id = "5d932d5c-00000041"
        NAS-Port = 66
        NAS-Port-Type = Wireless-802.11
        NAS-Identifier = "AP1"
        NAS-IP-Address = 192.168.0.250
        Framed-MTU = 1496
        User-Name = "test"
        Calling-Station-Id = "00-13-02-C4-80-4C"
        Called-Station-Id = "00-0F-61-FE-EF-D2"
        Service-Type = Framed-User
        EAP-Message = 0x026000061900
        State = 0x2fcecf572eaed62120794f6da7ad342b
        Colubris-AVPair = "ssid=test2"
        Colubris-AVPair = "vsc-unique-id=3"
        Colubris-AVPair = "phytype=IEEE802dot11g"
        Colubris-Attr-250 = 0x00000000
        Colubris-Attr-249 = 0x00000000
        Message-Authenticator = 0x1ebaf7372e7c92ad7d8260f57b9a5b91
+- entering group authorize {...}
++[preprocess] returns ok
++[chap] returns noop
++[mschap] returns noop
[eap] EAP packet type response id 96 length 6
[eap] Continuing tunnel setup.
++[eap] returns ok
Found Auth-Type = EAP
+- entering group authenticate {...}
[eap] Request found, released from the list
[eap] EAP/peap
[eap] processing type peap
[peap] processing EAP-TLS
[peap] Received TLS ACK
[peap] ACK handshake fragment handler
[peap] eaptls_verify returned 1
[peap] eaptls_process returned 13
[peap] EAPTLS_HANDLED
++[eap] returns handled
Sending Access-Challenge of id 178 to 192.168.0.250 port 32769
        EAP-Message = 0x016103fc194000f84114daab0be7b7300d06092a864886f70d0101050500308193310b3009060355040613024652310f300d060355040813065261646975733112301006035504071309536f6d65776865726531153013060355040a130c4578616d706c6520496e632e3120301e06092a864886f70d010901161161646d696e406578616d706c652e636f6d312630240603550403131d4578616d706c6520436572746966696361746520417574686f72697479301e170d3039313030323039313031395a170d3130313030323039313031395a308193310b3009060355040613024652310f300d060355040813065261646975733112301006035504
        EAP-Message = 0x071309536f6d65776865726531153013060355040a130c4578616d706c6520496e632e3120301e06092a864886f70d010901161161646d696e406578616d706c652e636f6d312630240603550403131d4578616d706c6520436572746966696361746520417574686f7269747930820122300d06092a864886f70d01010105000382010f003082010a0282010100d8c0369a50430220e6be9b2ea74e5aebe88d44fb308b2e21db06198ff7dcf4fc51d8ee7de0288c92f02afbbf5f564bb83a44cb368cc73c2f52b68e6623d89aeefd762b01cb76b12bd664cc6f275c607a84895560c199f6efd1ce93dfb65dad00efe94524ee2eaf152e15cc014d8d10
        EAP-Message = 0x41311ab4e247004020dbc030eb3571a6a3cc33b65bb54cf8b00dd14fe270dbef91a02256cc5f94bcc6ffeb666c2cd385285ce152d7238ad324698f46178889b1bc39c80eba888afb800b5052b862c19171fcf9cd875835b332aeafbd43b0a63e463301be72b76593143b759760cc7ba886f90b8e60b2fbb3e7ef95fc550ac60f85e2bb9d415839944b55dec59a2fca0abb0203010001a381fb3081f8301d0603551d0e04160414a2c3464e11a7008617648eab0f46b75fe4a40eff3081c80603551d230481c03081bd8014a2c3464e11a7008617648eab0f46b75fe4a40effa18199a48196308193310b3009060355040613024652310f300d06035504
        EAP-Message = 0x0813065261646975733112301006035504071309536f6d65776865726531153013060355040a130c4578616d706c6520496e632e3120301e06092a864886f70d010901161161646d696e406578616d706c652e636f6d312630240603550403131d4578616d706c6520436572746966696361746520417574686f72697479820900f84114daab0be7b7300c0603551d13040530030101ff300d06092a864886f70d0101050500038201010014eaec3226390d24c4596ebbcdad74bc520dcd44ea31d700aa52eb9ac125b7698865805eaa7a7a9e352fa2447ac01615694e3e21b99155e04af8004c747815765de8643b388c982bc50a0548d9916cd41c00
        EAP-Message = 0xdfaf4f5299b0ed37
        Message-Authenticator = 0x00000000000000000000000000000000
        State = 0x2fcecf572dafd62120794f6da7ad342b
Finished request 2.
Going to the next request
Waking up in 4.6 seconds.
rad_recv: Access-Request packet from host 192.168.0.250 port 32769, id=246, length=244
        Acct-Session-Id = "5d932d5c-00000041"
        NAS-Port = 66
        NAS-Port-Type = Wireless-802.11
        NAS-Identifier = "AP1"
        NAS-IP-Address = 192.168.0.250
        Framed-MTU = 1496
        User-Name = "test"
        Calling-Station-Id = "00-13-02-C4-80-4C"
        Called-Station-Id = "00-0F-61-FE-EF-D2"
        Service-Type = Framed-User
        EAP-Message = 0x026100061900
        State = 0x2fcecf572dafd62120794f6da7ad342b
        Colubris-AVPair = "ssid=test2"
        Colubris-AVPair = "vsc-unique-id=3"
        Colubris-AVPair = "phytype=IEEE802dot11g"
        Colubris-Attr-250 = 0x00000000
        Colubris-Attr-249 = 0x00000000
        Message-Authenticator = 0x6e6bdbe83c6e9eda220cedb9a5639bf4
+- entering group authorize {...}
++[preprocess] returns ok
++[chap] returns noop
++[mschap] returns noop
[eap] EAP packet type response id 97 length 6
[eap] Continuing tunnel setup.
++[eap] returns ok
Found Auth-Type = EAP
+- entering group authenticate {...}
[eap] Request found, released from the list
[eap] EAP/peap
[eap] processing type peap
[peap] processing EAP-TLS
[peap] Received TLS ACK
[peap] ACK handshake fragment handler
[peap] eaptls_verify returned 1
[peap] eaptls_process returned 13
[peap] EAPTLS_HANDLED
++[eap] returns handled
Sending Access-Challenge of id 246 to 192.168.0.250 port 32769
        EAP-Message = 0x016202c71900e378b675b53b6d1d9d964027ca531421ad8eb9ce9e7d4509859acc5a41591774af5d90898138585fb830556024743b9fd64a3be7f96729c33552a68a79494a06bcabade45ac7845132207a390c53b64ca7744107637a762ab6af1e4a2904f0bc5b0288bc5ce026e50697bd2b7a516853d24731b1ccc9b4ec68b506758fdfb4603025d235d1d747a54cfa6854379d200eecd25fd619d40a310408dc78d4afce7e6274dfceb917160301020d0c0002090080db32c6728a03d410c3f602a3c8e066dcd2a9347670777bad72d171c50e422d7c484f8079d74edbcc277256e0731dbafe0cfb93bb791e130c4e03649fd70828fdd37bc0bf6582
        EAP-Message = 0x3a85d7de9c343700a00312e93694ced41d066f97060c4dd2358da0f50b478d02e966c711d2f6c1b473007d3067b58f235da089965a2c094fa0eb0001020080a4b70f14f3f5e549aeb77525ff62c02a5a985f6073a398581df61badaba3b1f1f97151c61be99c64b22f1b481be6ed77060fc9c56d84ef2e8270142de7da609c8285ff1266b8ba1eafedcf079694b08bd9924a46c63df0e99931ae209932d5f287f72b2b004643fcd66637cee15c129c089618a0a39b5d6c972ecbb7434960040100ad9480936240b619c1e5695b7bfbfd51f1522c2ecab75c5535f078edaf0847b9e4c18506112d941fcc9ef4c108db6f6619fe5d9aa6838e1715baadd6
        EAP-Message = 0x2296ed5424e3645722105c509a0a85cf6239d70aad22d7f797060c6c771f728a417048af1ddae0a52d8c364ccefbf1a806db867e1891cbef2a479abcd859fd8c0a5bdd7069322788488836a350604cb51e5f6c4d5502978791ca1a13ac699173dc4c22c235352e0b5b0a1742ff65374b9d273cb904c00d15cb68078f8935f11e88e17a6873284a1a6fb029764e83d063e359ba02a9267e7b17083b13a20c466514621ba11b8e95d757d2230db62011e7d178db21ea5d9aeb940b91ce5230e0125a495d5d16030100040e000000
        Message-Authenticator = 0x00000000000000000000000000000000
        State = 0x2fcecf572cacd62120794f6da7ad342b
Finished request 3.
Going to the next request
Waking up in 4.6 seconds.
rad_recv: Access-Request packet from host 192.168.0.250 port 32769, id=231, length=442
        Acct-Session-Id = "5d932d5c-00000041"
        NAS-Port = 66
        NAS-Port-Type = Wireless-802.11
        NAS-Identifier = "AP1"
        NAS-IP-Address = 192.168.0.250
        Framed-MTU = 1496
        User-Name = "test"
        Calling-Station-Id = "00-13-02-C4-80-4C"
        Called-Station-Id = "00-0F-61-FE-EF-D2"
        Service-Type = Framed-User
        EAP-Message = 0x026200cc19001603010086100000820080b5cc5b339fe53445d3f7ed430880c499f58c06b3567156b7d895e1d53a3ac1dbb07bab47094750e8238053d80ca349b029ee16cb6555adc896f8c15be57152da44970493b5f722ebc70225ce833a0f71034f214247385d4c9deea3f0c73a942ec65dbcf554badb920959ac277242f1e3c00d24d6d645bcc54af5b5513d62bae91403010001011603010030f693dd10d11d9bedd5757ce36ad4a5d414f087fabe2f7a2f6c6e9f35bb227dbc1290fece4cf0c972561242f0cb3ac2aa
        State = 0x2fcecf572cacd62120794f6da7ad342b
        Colubris-AVPair = "ssid=test2"
        Colubris-AVPair = "vsc-unique-id=3"
        Colubris-AVPair = "phytype=IEEE802dot11g"
        Colubris-Attr-250 = 0x00000000
        Colubris-Attr-249 = 0x00000000
        Message-Authenticator = 0xee90a0281d9f9cbdc8f1cacd5d85ae72
+- entering group authorize {...}
++[preprocess] returns ok
++[chap] returns noop
++[mschap] returns noop
[eap] EAP packet type response id 98 length 204
[eap] Continuing tunnel setup.
++[eap] returns ok
Found Auth-Type = EAP
+- entering group authenticate {...}
[eap] Request found, released from the list
[eap] EAP/peap
[eap] processing type peap
[peap] processing EAP-TLS
[peap] eaptls_verify returned 7
[peap] Done initial handshake
[peap] <<< TLS 1.0 Handshake [length 0086], ClientKeyExchange
[peap]     TLS_accept: SSLv3 read client key exchange A
[peap] <<< TLS 1.0 ChangeCipherSpec [length 0001]
[peap] <<< TLS 1.0 Handshake [length 0010], Finished
[peap]     TLS_accept: SSLv3 read finished A
[peap] >>> TLS 1.0 ChangeCipherSpec [length 0001]
[peap]     TLS_accept: SSLv3 write change cipher spec A
[peap] >>> TLS 1.0 Handshake [length 0010], Finished
[peap]     TLS_accept: SSLv3 write finished A
[peap]     TLS_accept: SSLv3 flush data
[peap]     (other): SSL negotiation finished successfully
SSL Connection Established
[peap] eaptls_process returned 13
[peap] EAPTLS_HANDLED
++[eap] returns handled
Sending Access-Challenge of id 231 to 192.168.0.250 port 32769
        EAP-Message = 0x016300411900140301000101160301003064b2dc4e79c5269f640a26bf12e969fd27e08aeaa55bddc42296168c965b5f0318af8fbb2f2cfee50f210e09b8643167
        Message-Authenticator = 0x00000000000000000000000000000000
        State = 0x2fcecf572badd62120794f6da7ad342b
Finished request 4.
Going to the next request
Waking up in 4.4 seconds.
rad_recv: Access-Request packet from host 192.168.0.250 port 32769, id=189, length=244
        Acct-Session-Id = "5d932d5c-00000041"
        NAS-Port = 66
        NAS-Port-Type = Wireless-802.11
        NAS-Identifier = "AP1"
        NAS-IP-Address = 192.168.0.250
        Framed-MTU = 1496
        User-Name = "test"
        Calling-Station-Id = "00-13-02-C4-80-4C"
        Called-Station-Id = "00-0F-61-FE-EF-D2"
        Service-Type = Framed-User
        EAP-Message = 0x026300061900
        State = 0x2fcecf572badd62120794f6da7ad342b
        Colubris-AVPair = "ssid=test2"
        Colubris-AVPair = "vsc-unique-id=3"
        Colubris-AVPair = "phytype=IEEE802dot11g"
        Colubris-Attr-250 = 0x00000000
        Colubris-Attr-249 = 0x00000000
        Message-Authenticator = 0x70cc7f8c0205fc984383001a08fabf4b
+- entering group authorize {...}
++[preprocess] returns ok
++[chap] returns noop
++[mschap] returns noop
[eap] EAP packet type response id 99 length 6
[eap] Continuing tunnel setup.
++[eap] returns ok
Found Auth-Type = EAP
+- entering group authenticate {...}
[eap] Request found, released from the list
[eap] EAP/peap
[eap] processing type peap
[peap] processing EAP-TLS
[peap] Received TLS ACK
[peap] ACK handshake is finished
[peap] eaptls_verify returned 3
[peap] eaptls_process returned 3
[peap] EAPTLS_SUCCESS
++[eap] returns handled
Sending Access-Challenge of id 189 to 192.168.0.250 port 32769
        EAP-Message = 0x0164002b1900170301002047918cd4fb35c2fd04a77421bfce48db30ea8ad8309bce38ca5a534134cd9593
        Message-Authenticator = 0x00000000000000000000000000000000
        State = 0x2fcecf572aaad62120794f6da7ad342b
Finished request 5.
Going to the next request
Waking up in 4.4 seconds.
rad_recv: Access-Request packet from host 192.168.0.250 port 32769, id=192, length=334
        Acct-Session-Id = "5d932d5c-00000041"
        NAS-Port = 66
        NAS-Port-Type = Wireless-802.11
        NAS-Identifier = "AP1"
        NAS-IP-Address = 192.168.0.250
        Framed-MTU = 1496
        User-Name = "test"
        Calling-Station-Id = "00-13-02-C4-80-4C"
        Called-Station-Id = "00-0F-61-FE-EF-D2"
        Service-Type = Framed-User
        EAP-Message = 0x0264006019001703010020e385daed9a5abd99a147b3b044e03c8133ed64098945c5ed994058e737d3d3bd1703010030d75721162abcc54f933e7c50a085962f0386142cac8335a1754863a1f81083e42f11698fa24be542237c07c4f907122c
        State = 0x2fcecf572aaad62120794f6da7ad342b
        Colubris-AVPair = "ssid=test2"
        Colubris-AVPair = "vsc-unique-id=3"
        Colubris-AVPair = "phytype=IEEE802dot11g"
        Colubris-Attr-250 = 0x00000000
        Colubris-Attr-249 = 0x00000000
        Message-Authenticator = 0x95250447941475c741dff4a9a03d8751
+- entering group authorize {...}
++[preprocess] returns ok
++[chap] returns noop
++[mschap] returns noop
[eap] EAP packet type response id 100 length 96
[eap] Continuing tunnel setup.
++[eap] returns ok
Found Auth-Type = EAP
+- entering group authenticate {...}
[eap] Request found, released from the list
[eap] EAP/peap
[eap] processing type peap
[peap] processing EAP-TLS
[peap] eaptls_verify returned 7
[peap] Done initial handshake
[peap] eaptls_process returned 7
[peap] EAPTLS_OK
[peap] Session established.  Decoding tunneled attributes.
[peap] Identity - jacques.net\test
[peap] Got tunneled request
        EAP-Message = 0x02640015016a6163717565732e6e65745c74657374
server  {
  PEAP: Got tunneled identity of jacques.net\test
  PEAP: Setting default EAP type for tunneled EAP session.
  PEAP: Setting User-Name to jacques.net\test
Sending tunneled request
        EAP-Message = 0x02640015016a6163717565732e6e65745c74657374
        FreeRADIUS-Proxied-To = 127.0.0.1
        User-Name = "jacques.net\\test"
        Acct-Session-Id = "5d932d5c-00000041"
        NAS-Port = 66
        NAS-Port-Type = Wireless-802.11
        NAS-Identifier = "AP1"
        NAS-IP-Address = 192.168.0.250
        Framed-MTU = 1496
        Calling-Station-Id = "00-13-02-C4-80-4C"
        Called-Station-Id = "00-0F-61-FE-EF-D2"
        Service-Type = Framed-User
        Colubris-AVPair = "ssid=test2"
        Colubris-AVPair = "vsc-unique-id=3"
        Colubris-AVPair = "phytype=IEEE802dot11g"
        Colubris-Attr-250 = 0x00000000
        Colubris-Attr-249 = 0x00000000
server proxy-inner-tunnel {
+- entering group authorize {...}
++[control] returns notfound
} # server proxy-inner-tunnel
[peap] Got tunneled reply code 0
  PEAP: Calling authenticate in order to initiate tunneled EAP session.
+- entering group authenticate {...}
[eap] EAP Identity
[eap] processing type mschapv2
rlm_eap_mschapv2: Issuing Challenge
++[eap] returns handled
    PEAP: Cancelling proxy to realm jacques.net until the tunneled EAP session has been established
[peap] Got tunneled reply RADIUS code 11
        EAP-Message = 0x0165002a1a0165002510c9f3a03dd9167e53f280c339f4431c426a6163717565732e6e65745c74657374
        Message-Authenticator = 0x00000000000000000000000000000000
        State = 0x928e973392eb8d274d2ff22844d91b5e
[peap] Got tunneled Access-Challenge
++[eap] returns handled
Sending Access-Challenge of id 192 to 192.168.0.250 port 32769
        EAP-Message = 0x0165004b19001703010040e0d9d4c2bb39f8a5c845ec5f70766488da02768a7d12548ade59398869bb959ca9f079e62e907164f6a6ca94e60e791e3c090336acb39e35f45a2fb101fdbb73
        Message-Authenticator = 0x00000000000000000000000000000000
        State = 0x2fcecf5729abd62120794f6da7ad342b
Finished request 6.
Going to the next request
Waking up in 4.4 seconds.
rad_recv: Access-Request packet from host 192.168.0.250 port 32769, id=75, length=382
        Acct-Session-Id = "5d932d5c-00000041"
        NAS-Port = 66
        NAS-Port-Type = Wireless-802.11
        NAS-Identifier = "AP1"
        NAS-IP-Address = 192.168.0.250
        Framed-MTU = 1496
        User-Name = "test"
        Calling-Station-Id = "00-13-02-C4-80-4C"
        Called-Station-Id = "00-0F-61-FE-EF-D2"
        Service-Type = Framed-User
        EAP-Message = 0x0265009019001703010020b744c88368685ec8749929a9befeae06b12a4a95bec3478e763673bc3f5452eb170301006084b89ced117e15e555a55e5968eb574a01dd61c1de8c4cb7ddba3566ce5235a5fdadf9961e2d2fceb9988158d1c35bb752c0b47da89ddb5a75e3e020227c28512523d78b86a27deaf65b4e1cd0ecb4b73236c1e2ec886155c9081b82dc122728
        State = 0x2fcecf5729abd62120794f6da7ad342b
        Colubris-AVPair = "ssid=test2"
        Colubris-AVPair = "vsc-unique-id=3"
        Colubris-AVPair = "phytype=IEEE802dot11g"
        Colubris-Attr-250 = 0x00000000
        Colubris-Attr-249 = 0x00000000
        Message-Authenticator = 0x03649394ec9600d8479d5fb241761669
+- entering group authorize {...}
++[preprocess] returns ok
++[chap] returns noop
++[mschap] returns noop
[eap] EAP packet type response id 101 length 144
[eap] Continuing tunnel setup.
++[eap] returns ok
Found Auth-Type = EAP
+- entering group authenticate {...}
[eap] Request found, released from the list
[eap] EAP/peap
[eap] processing type peap
[peap] processing EAP-TLS
[peap] eaptls_verify returned 7
[peap] Done initial handshake
[peap] eaptls_process returned 7
[peap] EAPTLS_OK
[peap] Session established.  Decoding tunneled attributes.
[peap] EAP type mschapv2
[peap] Got tunneled request
        EAP-Message = 0x0265004b1a0265004631a2ff821ffbcf8e96b886df5b572c388400000000000000009d27f3bbe3c6090ceb192d59874fdfdab9b2d039e6af9719006a6163717565732e6e65745c74657374
server  {
  PEAP: Setting User-Name to jacques.net\test
Sending tunneled request
        EAP-Message = 0x0265004b1a0265004631a2ff821ffbcf8e96b886df5b572c388400000000000000009d27f3bbe3c6090ceb192d59874fdfdab9b2d039e6af9719006a6163717565732e6e65745c74657374
        FreeRADIUS-Proxied-To = 127.0.0.1
        User-Name = "jacques.net\\test"
        State = 0x928e973392eb8d274d2ff22844d91b5e
        Acct-Session-Id = "5d932d5c-00000041"
        NAS-Port = 66
        NAS-Port-Type = Wireless-802.11
        NAS-Identifier = "AP1"
        NAS-IP-Address = 192.168.0.250
        Framed-MTU = 1496
        Calling-Station-Id = "00-13-02-C4-80-4C"
        Called-Station-Id = "00-0F-61-FE-EF-D2"
        Service-Type = Framed-User
        Colubris-AVPair = "ssid=test2"
        Colubris-AVPair = "vsc-unique-id=3"
        Colubris-AVPair = "phytype=IEEE802dot11g"
        Colubris-Attr-250 = 0x00000000
        Colubris-Attr-249 = 0x00000000
server proxy-inner-tunnel {
+- entering group authorize {...}
++[control] returns notfound
} # server proxy-inner-tunnel
[peap] Got tunneled reply code 0
  PEAP: Calling authenticate in order to initiate tunneled EAP session.
+- entering group authenticate {...}
[eap] Request found, released from the list
[eap] EAP/mschapv2
[eap] processing type mschapv2
[eap]   Not-EAP proxy set.  Not composing EAP
++[eap] returns handled
  PEAP: Tunneled authentication will be proxied to jacques.net
  PEAP: Remembering to do EAP-MS-CHAP-V2 post-proxy.
[eap]   Tunneled session will be proxied.  Not doing EAP.
++[eap] returns handled
  WARNING: Empty section.  Using default return values.
ERROR: Failed to create a new socket for proxying requests.
ERROR: Failed inserting request into proxy hash.
ERROR: Failed to proxy request 7
There was no response configured: rejecting request 7
Using Post-Auth-Type Reject
+- entering group REJECT {...}
[attr_filter.access_reject]     expand: %{User-Name} -> test
 attr_filter: Matched entry DEFAULT at line 11
++[attr_filter.access_reject] returns updated
Delaying reject of request 7 for 1 seconds
Going to the next request
Waking up in 0.9 seconds.
Sending delayed reject for request 7
Sending Access-Reject of id 75 to 192.168.0.250 port 32769
Waking up in 3.4 seconds.
Cleaning up request 0 ID 94 with timestamp +7
Waking up in 0.2 seconds.
Cleaning up request 1 ID 21 with timestamp +7
Cleaning up request 2 ID 178 with timestamp +7
Cleaning up request 3 ID 246 with timestamp +7
Waking up in 0.1 seconds.
Cleaning up request 4 ID 231 with timestamp +8
Cleaning up request 5 ID 189 with timestamp +8
Cleaning up request 6 ID 192 with timestamp +8
Waking up in 1.0 seconds.
Cleaning up request 7 ID 75 with timestamp +8
Ready to process requests.
^C
debian:/usr/local/etc/raddb#
